Dec 03 13:52:03.918 INFO testing dmenv-0.6.0 against master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61 for beta-1.31-5
Dec 03 13:52:03.919 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-1/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/beta-1.31-5/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +8940af3cea4a9a9deabcaf843b2f8cad9a83fc61-alt build --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 03 13:52:04.302 INFO [stdout] daaad108c77a8eac010f7138fbc4cbe4ebe496a1dc8145611478ea135dd5e6d7
Dec 03 13:52:04.305 INFO running `"docker" "start" "-a" "daaad108c77a8eac010f7138fbc4cbe4ebe496a1dc8145611478ea135dd5e6d7"`
Dec 03 13:52:05.239 INFO [stderr] usermod: no changes
Dec 03 13:52:05.347 INFO [stderr]    Compiling dmenv v0.6.0 (/source)
Dec 03 13:52:12.638 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 7.37s
Dec 03 13:52:12.647 INFO [stderr] su: No module specific data is present
Dec 03 13:52:13.003 INFO running `"docker" "inspect" "daaad108c77a8eac010f7138fbc4cbe4ebe496a1dc8145611478ea135dd5e6d7"`
Dec 03 13:52:13.108 INFO running `"docker" "rm" "-f" "daaad108c77a8eac010f7138fbc4cbe4ebe496a1dc8145611478ea135dd5e6d7"`
Dec 03 13:52:13.225 INFO [stdout] daaad108c77a8eac010f7138fbc4cbe4ebe496a1dc8145611478ea135dd5e6d7
Dec 03 13:52:13.228 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-1/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/beta-1.31-5/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +8940af3cea4a9a9deabcaf843b2f8cad9a83fc61-alt test --frozen --no-run" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 03 13:52:13.462 INFO [stdout] 3b3c78d0aedca85314a64677ecfb56d263367307d29c613ab1cadc25edb8dde7
Dec 03 13:52:13.465 INFO running `"docker" "start" "-a" "3b3c78d0aedca85314a64677ecfb56d263367307d29c613ab1cadc25edb8dde7"`
Dec 03 13:52:13.947 INFO [stderr] usermod: no changes
Dec 03 13:52:14.075 INFO [stderr]    Compiling dmenv v0.6.0 (/source)
Dec 03 13:52:21.563 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 7.58s
Dec 03 13:52:21.568 INFO [stderr] su: No module specific data is present
Dec 03 13:52:22.312 INFO running `"docker" "inspect" "3b3c78d0aedca85314a64677ecfb56d263367307d29c613ab1cadc25edb8dde7"`
Dec 03 13:52:22.623 INFO running `"docker" "rm" "-f" "3b3c78d0aedca85314a64677ecfb56d263367307d29c613ab1cadc25edb8dde7"`
Dec 03 13:52:22.899 INFO [stdout] 3b3c78d0aedca85314a64677ecfb56d263367307d29c613ab1cadc25edb8dde7
Dec 03 13:52:22.905 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-1/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/beta-1.31-5/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +8940af3cea4a9a9deabcaf843b2f8cad9a83fc61-alt test --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 03 13:52:23.166 INFO [stdout] bc0e766468fb5cd8c81c02d4a6b2ebb59fbe1922e37b22220a29110b141a3542
Dec 03 13:52:23.169 INFO running `"docker" "start" "-a" "bc0e766468fb5cd8c81c02d4a6b2ebb59fbe1922e37b22220a29110b141a3542"`
Dec 03 13:52:23.886 INFO [stderr] usermod: no changes
Dec 03 13:52:23.980 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.07s
Dec 03 13:52:24.001 INFO [stderr]      Running /target/debug/deps/dmenv-e0ec94863092619c
Dec 03 13:52:24.003 INFO [stderr]      Running /target/debug/deps/dmenv-51f27c3f13ab0cb3
Dec 03 13:52:24.003 INFO [stdout] 
Dec 03 13:52:24.003 INFO [stdout] running 0 tests
Dec 03 13:52:24.003 INFO [stdout] 
Dec 03 13:52:24.003 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 03 13:52:24.003 INFO [stdout] 
Dec 03 13:52:24.007 INFO [stdout] 
Dec 03 13:52:24.007 INFO [stdout] running 0 tests
Dec 03 13:52:24.007 INFO [stdout] 
Dec 03 13:52:24.007 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 03 13:52:24.007 INFO [stdout] 
Dec 03 13:52:24.007 INFO [stdout] 
Dec 03 13:52:24.007 INFO [stdout] running 10 tests
Dec 03 13:52:24.007 INFO [stderr]      Running /target/debug/deps/integration_tests-43739444e206818d
Dec 03 13:52:24.028 INFO [stdout] test run_without_args ... ok
Dec 03 13:52:24.157 INFO [stdout] test init_generates_setup_py ... ok
Dec 03 13:52:24.161 INFO [stdout] test run_without_virtualenv ... ok
Dec 03 13:52:24.168 INFO [stdout] test init_does_not_overwrite_existing_setup_py ... ok
Dec 03 13:52:24.183 INFO [stdout] test install_without_lock ... ok
Dec 03 13:52:24.193 INFO [stdout] test lock_complains_if_setup_py_does_not_exist ... ok
Dec 03 13:52:24.233 INFO [stdout] test show_venv_path ... ok
Dec 03 13:52:27.803 INFO [stderr] The directory '/home/crater/.cache/pip/http' or its parent directory is not owned by the current user and the cache has been disabled. Please check the permissions and owner of that directory. If executing pip with sudo, you may want sudo's -H flag.
Dec 03 13:52:27.807 INFO [stderr] The directory '/home/crater/.cache/pip' or its parent directory is not owned by the current user and caching wheels has been disabled. check the permissions and owner of that directory. If executing pip with sudo, you may want sudo's -H flag.
Dec 03 13:52:27.886 INFO [stdout] Collecting atomicwrites==1.2.1 (from -r /tmp/test-dmenv.bjwJ28ohTGhe/requirements.lock (line 2))
Dec 03 13:52:27.899 INFO [stderr] Exception:
Dec 03 13:52:27.899 INFO [stderr] Traceback (most recent call last):
Dec 03 13:52:27.899 INFO [stderr]   File "/tmp/test-dmenv.bjwJ28ohTGhe/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connection.py", line 137, in _new_conn
Dec 03 13:52:27.899 INFO [stderr]     (self.host, self.port), self.timeout, **extra_kw)
Dec 03 13:52:27.899 INFO [stderr]   File "/tmp/test-dmenv.bjwJ28ohTGhe/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/util/connection.py", line 67, in create_connection
Dec 03 13:52:27.899 INFO [stderr]     for res in socket.getaddrinfo(host, port, 0, socket.SOCK_STREAM):
Dec 03 13:52:27.899 INFO [stderr]   File "/usr/lib/python3.5/socket.py", line 732, in getaddrinfo
Dec 03 13:52:27.899 INFO [stderr]     for res in _socket.getaddrinfo(host, port, family, type, proto, flags):
Dec 03 13:52:27.899 INFO [stderr] socket.gaierror: [Errno -3] Temporary failure in name resolution
Dec 03 13:52:27.899 INFO [stderr] 
Dec 03 13:52:27.899 INFO [stderr] During handling of the above exception, another exception occurred:
Dec 03 13:52:27.899 INFO [stderr] 
Dec 03 13:52:27.899 INFO [stderr] Traceback (most recent call last):
Dec 03 13:52:27.899 INFO [stderr]   File "/tmp/test-dmenv.bjwJ28ohTGhe/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connectionpool.py", line 560, in urlopen
Dec 03 13:52:27.899 INFO [stderr]     body=body, headers=headers)
Dec 03 13:52:27.899 INFO [stderr]   File "/tmp/test-dmenv.bjwJ28ohTGhe/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connectionpool.py", line 346, in _make_request
Dec 03 13:52:27.899 INFO [stderr]     self._validate_conn(conn)
Dec 03 13:52:27.899 INFO [stderr]   File "/tmp/test-dmenv.bjwJ28ohTGhe/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connectionpool.py", line 787, in _validate_conn
Dec 03 13:52:27.899 INFO [stderr]     conn.connect()
Dec 03 13:52:27.899 INFO [stderr]   File "/tmp/test-dmenv.bjwJ28ohTGhe/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connection.py", line 217, in connect
Dec 03 13:52:27.899 INFO [stderr]     conn = self._new_conn()
Dec 03 13:52:27.899 INFO [stderr]   File "/tmp/test-dmenv.bjwJ28ohTGhe/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connection.py", line 146, in _new_conn
Dec 03 13:52:27.899 INFO [stderr]     self, "Failed to establish a new connection: %s" % e)
Dec 03 13:52:27.899 INFO [stderr] requests.packages.urllib3.exceptions.NewConnectionError: <requests.packages.urllib3.connection.VerifiedHTTPSConnection object at 0x7f6a12e29358>: Failed to establish a new connection: [Errno -3] Temporary failure in name resolution
Dec 03 13:52:27.899 INFO [stderr] 
Dec 03 13:52:27.899 INFO [stderr] During handling of the above exception, another exception occurred:
Dec 03 13:52:27.899 INFO [stderr] 
Dec 03 13:52:27.899 INFO [stderr] Traceback (most recent call last):
Dec 03 13:52:27.899 INFO [stderr]   File "/tmp/test-dmenv.bjwJ28ohTGhe/.venv/3.5.2/lib/python3.5/site-packages/pip/basecommand.py", line 209, in main
Dec 03 13:52:27.899 INFO [stderr]     status = self.run(options, args)
Dec 03 13:52:27.900 INFO [stderr]   File "/tmp/test-dmenv.bjwJ28ohTGhe/.venv/3.5.2/lib/python3.5/site-packages/pip/commands/install.py", line 317, in run
Dec 03 13:52:27.900 INFO [stderr]     requirement_set.prepare_files(finder)
Dec 03 13:52:27.900 INFO [stderr]   File "/tmp/test-dmenv.bjwJ28ohTGhe/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 360, in prepare_files
Dec 03 13:52:27.900 INFO [stderr]     ignore_dependencies=self.ignore_dependencies))
Dec 03 13:52:27.900 INFO [stderr]   File "/tmp/test-dmenv.bjwJ28ohTGhe/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 512, in _prepare_file
Dec 03 13:52:27.900 INFO [stderr]     finder, self.upgrade, require_hashes)
Dec 03 13:52:27.900 INFO [stderr]   File "/tmp/test-dmenv.bjwJ28ohTGhe/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_install.py", line 273, in populate_link
Dec 03 13:52:27.900 INFO [stderr]     self.link = finder.find_requirement(self, upgrade)
Dec 03 13:52:27.900 INFO [stderr]   File "/tmp/test-dmenv.bjwJ28ohTGhe/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 442, in find_requirement
Dec 03 13:52:27.900 INFO [stderr]     all_candidates = self.find_all_candidates(req.name)
Dec 03 13:52:27.900 INFO [stderr]   File "/tmp/test-dmenv.bjwJ28ohTGhe/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 400, in find_all_candidates
Dec 03 13:52:27.900 INFO [stderr]     for page in self._get_pages(url_locations, project_name):
Dec 03 13:52:27.900 INFO [stderr]   File "/tmp/test-dmenv.bjwJ28ohTGhe/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 545, in _get_pages
Dec 03 13:52:27.900 INFO [stderr]     page = self._get_page(location)
Dec 03 13:52:27.900 INFO [stderr]   File "/tmp/test-dmenv.bjwJ28ohTGhe/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 648, in _get_page
Dec 03 13:52:27.900 INFO [stderr]     return HTMLPage.get_page(link, session=self.session)
Dec 03 13:52:27.900 INFO [stderr]   File "/tmp/test-dmenv.bjwJ28ohTGhe/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 757, in get_page
Dec 03 13:52:27.900 INFO [stderr]     "Cache-Control": "max-age=600",
Dec 03 13:52:27.900 INFO [stderr]   File "/tmp/test-dmenv.bjwJ28ohTGhe/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 480, in get
Dec 03 13:52:27.900 INFO [stderr]     return self.request('GET', url, **kwargs)
Dec 03 13:52:27.900 INFO [stderr]   File "/tmp/test-dmenv.bjwJ28ohTGhe/.venv/3.5.2/lib/python3.5/site-packages/pip/download.py", line 378, in request
Dec 03 13:52:27.900 INFO [stderr]     return super(PipSession, self).request(method, url, *args, **kwargs)
Dec 03 13:52:27.900 INFO [stderr]   File "/tmp/test-dmenv.bjwJ28ohTGhe/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 468, in request
Dec 03 13:52:27.900 INFO [stderr]     resp = self.send(prep, **send_kwargs)
Dec 03 13:52:27.900 INFO [stderr]   File "/tmp/test-dmenv.bjwJ28ohTGhe/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 576, in send
Dec 03 13:52:27.900 INFO [stderr]     r = adapter.send(request, **kwargs)
Dec 03 13:52:27.900 INFO [stderr]   File "/tmp/test-dmenv.bjwJ28ohTGhe/.venv/3.5.2/share/python-wheels/CacheControl-0.11.5-py2.py3-none-any.whl/cachecontrol/adapter.py", line 46, in send
Dec 03 13:52:27.900 INFO [stderr]     resp = super(CacheControlAdapter, self).send(request, **kw)
Dec 03 13:52:27.900 INFO [stderr]   File "/tmp/test-dmenv.bjwJ28ohTGhe/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/adapters.py", line 376, in send
Dec 03 13:52:27.900 INFO [stderr]     timeout=timeout
Dec 03 13:52:27.900 INFO [stderr]   File "/tmp/test-dmenv.bjwJ28ohTGhe/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connectionpool.py", line 610, in urlopen
Dec 03 13:52:27.900 INFO [stderr]     _stacktrace=sys.exc_info()[2])
Dec 03 13:52:27.900 INFO [stderr]   File "/tmp/test-dmenv.bjwJ28ohTGhe/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/util/retry.py", line 228, in increment
Dec 03 13:52:27.900 INFO [stderr]     total -= 1
Dec 03 13:52:27.900 INFO [stderr] TypeError: unsupported operand type(s) for -=: 'Retry' and 'int'
Dec 03 13:52:27.918 INFO [stderr] The directory '/home/crater/.cache/pip/http' or its parent directory is not owned by the current user and the cache has been disabled. Please check the permissions and owner of that directory. If executing pip with sudo, you may want sudo's -H flag.
Dec 03 13:52:27.924 INFO [stderr] The directory '/home/crater/.cache/pip' or its parent directory is not owned by the current user and caching wheels has been disabled. check the permissions and owner of that directory. If executing pip with sudo, you may want sudo's -H flag.
Dec 03 13:52:27.999 INFO [stderr] Exception:
Dec 03 13:52:27.999 INFO [stderr] Traceback (most recent call last):
Dec 03 13:52:27.999 INFO [stderr]   File "/tmp/test-dmenv.Y9IlLryhP4k2/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connection.py", line 137, in _new_conn
Dec 03 13:52:27.999 INFO [stderr]     (self.host, self.port), self.timeout, **extra_kw)
Dec 03 13:52:27.999 INFO [stderr]   File "/tmp/test-dmenv.Y9IlLryhP4k2/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/util/connection.py", line 67, in create_connection
Dec 03 13:52:27.999 INFO [stderr]     for res in socket.getaddrinfo(host, port, 0, socket.SOCK_STREAM):
Dec 03 13:52:27.999 INFO [stderr]   File "/usr/lib/python3.5/socket.py", line 732, in getaddrinfo
Dec 03 13:52:27.999 INFO [stderr]     for res in _socket.getaddrinfo(host, port, family, type, proto, flags):
Dec 03 13:52:27.999 INFO [stderr] socket.gaierror: [Errno -3] Temporary failure in name resolution
Dec 03 13:52:27.999 INFO [stderr] 
Dec 03 13:52:27.999 INFO [stderr] During handling of the above exception, another exception occurred:
Dec 03 13:52:27.999 INFO [stderr] 
Dec 03 13:52:27.999 INFO [stderr] Traceback (most recent call last):
Dec 03 13:52:27.999 INFO [stderr]   File "/tmp/test-dmenv.Y9IlLryhP4k2/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connectionpool.py", line 560, in urlopen
Dec 03 13:52:27.999 INFO [stderr]     body=body, headers=headers)
Dec 03 13:52:27.999 INFO [stderr]   File "/tmp/test-dmenv.Y9IlLryhP4k2/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connectionpool.py", line 346, in _make_request
Dec 03 13:52:27.999 INFO [stderr]     self._validate_conn(conn)
Dec 03 13:52:27.999 INFO [stderr]   File "/tmp/test-dmenv.Y9IlLryhP4k2/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connectionpool.py", line 787, in _validate_conn
Dec 03 13:52:27.999 INFO [stderr]     conn.connect()
Dec 03 13:52:27.999 INFO [stderr]   File "/tmp/test-dmenv.Y9IlLryhP4k2/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connection.py", line 217, in connect
Dec 03 13:52:27.999 INFO [stderr]     conn = self._new_conn()
Dec 03 13:52:27.999 INFO [stderr]   File "/tmp/test-dmenv.Y9IlLryhP4k2/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connection.py", line 146, in _new_conn
Dec 03 13:52:27.999 INFO [stderr]     self, "Failed to establish a new connection: %s" % e)
Dec 03 13:52:27.999 INFO [stderr] requests.packages.urllib3.exceptions.NewConnectionError: <requests.packages.urllib3.connection.VerifiedHTTPSConnection object at 0x7f47c50c5710>: Failed to establish a new connection: [Errno -3] Temporary failure in name resolution
Dec 03 13:52:27.999 INFO [stderr] 
Dec 03 13:52:27.999 INFO [stderr] During handling of the above exception, another exception occurred:
Dec 03 13:52:27.999 INFO [stderr] 
Dec 03 13:52:27.999 INFO [stderr] Traceback (most recent call last):
Dec 03 13:52:27.999 INFO [stderr]   File "/tmp/test-dmenv.Y9IlLryhP4k2/.venv/3.5.2/lib/python3.5/site-packages/pip/basecommand.py", line 209, in main
Dec 03 13:52:27.999 INFO [stderr]     status = self.run(options, args)
Dec 03 13:52:28.000 INFO [stderr]   File "/tmp/test-dmenv.Y9IlLryhP4k2/.venv/3.5.2/lib/python3.5/site-packages/pip/commands/install.py", line 317, in run
Dec 03 13:52:28.000 INFO [stderr]     requirement_set.prepare_files(finder)
Dec 03 13:52:28.000 INFO [stderr]   File "/tmp/test-dmenv.Y9IlLryhP4k2/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 360, in prepare_files
Dec 03 13:52:28.000 INFO [stderr]     ignore_dependencies=self.ignore_dependencies))
Dec 03 13:52:28.000 INFO [stderr]   File "/tmp/test-dmenv.Y9IlLryhP4k2/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 448, in _prepare_file
Dec 03 13:52:28.000 INFO [stderr]     req_to_install, finder)
Dec 03 13:52:28.000 INFO [stderr]   File "/tmp/test-dmenv.Y9IlLryhP4k2/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 397, in _check_skip_installed
Dec 03 13:52:28.000 INFO [stderr]     finder.find_requirement(req_to_install, self.upgrade)
Dec 03 13:52:28.000 INFO [stderr]   File "/tmp/test-dmenv.Y9IlLryhP4k2/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 442, in find_requirement
Dec 03 13:52:28.000 INFO [stderr]     all_candidates = self.find_all_candidates(req.name)
Dec 03 13:52:28.000 INFO [stderr]   File "/tmp/test-dmenv.Y9IlLryhP4k2/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 400, in find_all_candidates
Dec 03 13:52:28.000 INFO [stderr]     for page in self._get_pages(url_locations, project_name):
Dec 03 13:52:28.000 INFO [stderr]   File "/tmp/test-dmenv.Y9IlLryhP4k2/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 545, in _get_pages
Dec 03 13:52:28.000 INFO [stderr]     page = self._get_page(location)
Dec 03 13:52:28.000 INFO [stderr]   File "/tmp/test-dmenv.Y9IlLryhP4k2/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 648, in _get_page
Dec 03 13:52:28.000 INFO [stderr]     return HTMLPage.get_page(link, session=self.session)
Dec 03 13:52:28.000 INFO [stderr]   File "/tmp/test-dmenv.Y9IlLryhP4k2/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 757, in get_page
Dec 03 13:52:28.000 INFO [stderr]     "Cache-Control": "max-age=600",
Dec 03 13:52:28.000 INFO [stderr]   File "/tmp/test-dmenv.Y9IlLryhP4k2/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 480, in get
Dec 03 13:52:28.000 INFO [stderr]     return self.request('GET', url, **kwargs)
Dec 03 13:52:28.000 INFO [stderr]   File "/tmp/test-dmenv.Y9IlLryhP4k2/.venv/3.5.2/lib/python3.5/site-packages/pip/download.py", line 378, in request
Dec 03 13:52:28.000 INFO [stderr]     return super(PipSession, self).request(method, url, *args, **kwargs)
Dec 03 13:52:28.000 INFO [stderr]   File "/tmp/test-dmenv.Y9IlLryhP4k2/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 468, in request
Dec 03 13:52:28.000 INFO [stderr]     resp = self.send(prep, **send_kwargs)
Dec 03 13:52:28.000 INFO [stderr]   File "/tmp/test-dmenv.Y9IlLryhP4k2/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 576, in send
Dec 03 13:52:28.000 INFO [stderr]     r = adapter.send(request, **kwargs)
Dec 03 13:52:28.000 INFO [stderr]   File "/tmp/test-dmenv.Y9IlLryhP4k2/.venv/3.5.2/share/python-wheels/CacheControl-0.11.5-py2.py3-none-any.whl/cachecontrol/adapter.py", line 46, in send
Dec 03 13:52:28.000 INFO [stderr]     resp = super(CacheControlAdapter, self).send(request, **kw)
Dec 03 13:52:28.000 INFO [stderr]   File "/tmp/test-dmenv.Y9IlLryhP4k2/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/adapters.py", line 376, in send
Dec 03 13:52:28.000 INFO [stderr]     timeout=timeout
Dec 03 13:52:28.000 INFO [stderr]   File "/tmp/test-dmenv.Y9IlLryhP4k2/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connectionpool.py", line 610, in urlopen
Dec 03 13:52:28.000 INFO [stderr]     _stacktrace=sys.exc_info()[2])
Dec 03 13:52:28.000 INFO [stderr]   File "/tmp/test-dmenv.Y9IlLryhP4k2/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/util/retry.py", line 228, in increment
Dec 03 13:52:28.000 INFO [stderr]     total -= 1
Dec 03 13:52:28.000 INFO [stderr] TypeError: unsupported operand type(s) for -=: 'Retry' and 'int'
Dec 03 13:52:28.064 INFO [stdout] test install_workflow_step_by_step ... FAILED
Dec 03 13:52:28.098 INFO [stdout] test lock_workflow ... FAILED
Dec 03 13:52:28.148 INFO [stderr] The directory '/home/crater/.cache/pip/http' or its parent directory is not owned by the current user and the cache has been disabled. Please check the permissions and owner of that directory. If executing pip with sudo, you may want sudo's -H flag.
Dec 03 13:52:28.151 INFO [stderr] The directory '/home/crater/.cache/pip' or its parent directory is not owned by the current user and caching wheels has been disabled. check the permissions and owner of that directory. If executing pip with sudo, you may want sudo's -H flag.
Dec 03 13:52:28.195 INFO [stderr] Exception:
Dec 03 13:52:28.195 INFO [stderr] Traceback (most recent call last):
Dec 03 13:52:28.195 INFO [stderr]   File "/tmp/test-dmenv.S3a86RelmbYi/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connection.py", line 137, in _new_conn
Dec 03 13:52:28.195 INFO [stderr]     (self.host, self.port), self.timeout, **extra_kw)
Dec 03 13:52:28.195 INFO [stderr]   File "/tmp/test-dmenv.S3a86RelmbYi/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/util/connection.py", line 67, in create_connection
Dec 03 13:52:28.195 INFO [stderr]     for res in socket.getaddrinfo(host, port, 0, socket.SOCK_STREAM):
Dec 03 13:52:28.195 INFO [stderr]   File "/usr/lib/python3.5/socket.py", line 732, in getaddrinfo
Dec 03 13:52:28.195 INFO [stderr]     for res in _socket.getaddrinfo(host, port, family, type, proto, flags):
Dec 03 13:52:28.195 INFO [stderr] socket.gaierror: [Errno -3] Temporary failure in name resolution
Dec 03 13:52:28.195 INFO [stderr] 
Dec 03 13:52:28.195 INFO [stderr] During handling of the above exception, another exception occurred:
Dec 03 13:52:28.195 INFO [stderr] 
Dec 03 13:52:28.195 INFO [stderr] Traceback (most recent call last):
Dec 03 13:52:28.195 INFO [stderr]   File "/tmp/test-dmenv.S3a86RelmbYi/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connectionpool.py", line 560, in urlopen
Dec 03 13:52:28.195 INFO [stderr]     body=body, headers=headers)
Dec 03 13:52:28.195 INFO [stderr]   File "/tmp/test-dmenv.S3a86RelmbYi/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connectionpool.py", line 346, in _make_request
Dec 03 13:52:28.195 INFO [stderr]     self._validate_conn(conn)
Dec 03 13:52:28.195 INFO [stderr]   File "/tmp/test-dmenv.S3a86RelmbYi/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connectionpool.py", line 787, in _validate_conn
Dec 03 13:52:28.195 INFO [stderr]     conn.connect()
Dec 03 13:52:28.195 INFO [stderr]   File "/tmp/test-dmenv.S3a86RelmbYi/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connection.py", line 217, in connect
Dec 03 13:52:28.195 INFO [stderr]     conn = self._new_conn()
Dec 03 13:52:28.195 INFO [stderr]   File "/tmp/test-dmenv.S3a86RelmbYi/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connection.py", line 146, in _new_conn
Dec 03 13:52:28.195 INFO [stderr]     self, "Failed to establish a new connection: %s" % e)
Dec 03 13:52:28.195 INFO [stderr] requests.packages.urllib3.exceptions.NewConnectionError: <requests.packages.urllib3.connection.VerifiedHTTPSConnection object at 0x7f35dc73bf28>: Failed to establish a new connection: [Errno -3] Temporary failure in name resolution
Dec 03 13:52:28.195 INFO [stderr] 
Dec 03 13:52:28.195 INFO [stderr] During handling of the above exception, another exception occurred:
Dec 03 13:52:28.195 INFO [stderr] 
Dec 03 13:52:28.195 INFO [stderr] Traceback (most recent call last):
Dec 03 13:52:28.195 INFO [stderr]   File "/tmp/test-dmenv.S3a86RelmbYi/.venv/3.5.2/lib/python3.5/site-packages/pip/basecommand.py", line 209, in main
Dec 03 13:52:28.195 INFO [stderr]     status = self.run(options, args)
Dec 03 13:52:28.195 INFO [stderr]   File "/tmp/test-dmenv.S3a86RelmbYi/.venv/3.5.2/lib/python3.5/site-packages/pip/commands/install.py", line 317, in run
Dec 03 13:52:28.195 INFO [stderr]     requirement_set.prepare_files(finder)
Dec 03 13:52:28.195 INFO [stderr]   File "/tmp/test-dmenv.S3a86RelmbYi/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 360, in prepare_files
Dec 03 13:52:28.195 INFO [stderr]     ignore_dependencies=self.ignore_dependencies))
Dec 03 13:52:28.195 INFO [stderr]   File "/tmp/test-dmenv.S3a86RelmbYi/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 448, in _prepare_file
Dec 03 13:52:28.195 INFO [stderr]     req_to_install, finder)
Dec 03 13:52:28.195 INFO [stderr]   File "/tmp/test-dmenv.S3a86RelmbYi/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 397, in _check_skip_installed
Dec 03 13:52:28.195 INFO [stderr]     finder.find_requirement(req_to_install, self.upgrade)
Dec 03 13:52:28.195 INFO [stderr]   File "/tmp/test-dmenv.S3a86RelmbYi/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 442, in find_requirement
Dec 03 13:52:28.195 INFO [stderr]     all_candidates = self.find_all_candidates(req.name)
Dec 03 13:52:28.195 INFO [stderr]   File "/tmp/test-dmenv.S3a86RelmbYi/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 400, in find_all_candidates
Dec 03 13:52:28.195 INFO [stderr]     for page in self._get_pages(url_locations, project_name):
Dec 03 13:52:28.196 INFO [stderr]   File "/tmp/test-dmenv.S3a86RelmbYi/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 545, in _get_pages
Dec 03 13:52:28.196 INFO [stderr]     page = self._get_page(location)
Dec 03 13:52:28.196 INFO [stderr]   File "/tmp/test-dmenv.S3a86RelmbYi/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 648, in _get_page
Dec 03 13:52:28.196 INFO [stderr]     return HTMLPage.get_page(link, session=self.session)
Dec 03 13:52:28.196 INFO [stderr]   File "/tmp/test-dmenv.S3a86RelmbYi/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 757, in get_page
Dec 03 13:52:28.196 INFO [stderr]     "Cache-Control": "max-age=600",
Dec 03 13:52:28.196 INFO [stderr]   File "/tmp/test-dmenv.S3a86RelmbYi/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 480, in get
Dec 03 13:52:28.196 INFO [stderr]     return self.request('GET', url, **kwargs)
Dec 03 13:52:28.196 INFO [stderr]   File "/tmp/test-dmenv.S3a86RelmbYi/.venv/3.5.2/lib/python3.5/site-packages/pip/download.py", line 378, in request
Dec 03 13:52:28.196 INFO [stderr]     return super(PipSession, self).request(method, url, *args, **kwargs)
Dec 03 13:52:28.196 INFO [stderr]   File "/tmp/test-dmenv.S3a86RelmbYi/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 468, in request
Dec 03 13:52:28.196 INFO [stderr]     resp = self.send(prep, **send_kwargs)
Dec 03 13:52:28.196 INFO [stderr]   File "/tmp/test-dmenv.S3a86RelmbYi/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 576, in send
Dec 03 13:52:28.196 INFO [stderr]     r = adapter.send(request, **kwargs)
Dec 03 13:52:28.196 INFO [stderr]   File "/tmp/test-dmenv.S3a86RelmbYi/.venv/3.5.2/share/python-wheels/CacheControl-0.11.5-py2.py3-none-any.whl/cachecontrol/adapter.py", line 46, in send
Dec 03 13:52:28.196 INFO [stderr]     resp = super(CacheControlAdapter, self).send(request, **kw)
Dec 03 13:52:28.196 INFO [stderr]   File "/tmp/test-dmenv.S3a86RelmbYi/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/adapters.py", line 376, in send
Dec 03 13:52:28.196 INFO [stderr]     timeout=timeout
Dec 03 13:52:28.196 INFO [stderr]   File "/tmp/test-dmenv.S3a86RelmbYi/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connectionpool.py", line 610, in urlopen
Dec 03 13:52:28.196 INFO [stderr]     _stacktrace=sys.exc_info()[2])
Dec 03 13:52:28.196 INFO [stderr]   File "/tmp/test-dmenv.S3a86RelmbYi/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/util/retry.py", line 228, in increment
Dec 03 13:52:28.196 INFO [stderr]     total -= 1
Dec 03 13:52:28.196 INFO [stderr] TypeError: unsupported operand type(s) for -=: 'Retry' and 'int'
Dec 03 13:52:28.291 INFO [stderr] error: test failed, to rerun pass '--test integration_tests'
Dec 03 13:52:28.291 INFO [stdout] test install_workflow_all_in_one ... FAILED
Dec 03 13:52:28.291 INFO [stdout] 
Dec 03 13:52:28.291 INFO [stdout] failures:
Dec 03 13:52:28.291 INFO [stdout] 
Dec 03 13:52:28.291 INFO [stdout] ---- install_workflow_step_by_step stdout ----
Dec 03 13:52:28.291 INFO [stdout] [34m::[0m Creating virtualenv in: /tmp/test-dmenv.bjwJ28ohTGhe/.venv/3.5.2
Dec 03 13:52:28.291 INFO [stdout] [34m->[0m running [1m/usr/bin/python3[0m -m venv /tmp/test-dmenv.bjwJ28ohTGhe/.venv/3.5.2
Dec 03 13:52:28.291 INFO [stdout] [34m->[0m running [1m/tmp/test-dmenv.bjwJ28ohTGhe/.venv/3.5.2/bin/pip[0m install --requirement /tmp/test-dmenv.bjwJ28ohTGhe/requirements.lock
Dec 03 13:52:28.291 INFO [stdout] thread 'install_workflow_step_by_step' panicked at ': Error { description: "command failed" }', libcore/result.rs:1009:5
Dec 03 13:52:28.291 INFO [stdout] stack backtrace:
Dec 03 13:52:28.291 INFO [stdout]    0:     0x5584b44f3bff - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h3a8b83e1922cbdde
Dec 03 13:52:28.291 INFO [stdout]                                at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49
Dec 03 13:52:28.291 INFO [stdout]    1:     0x5584b4500bc7 - std::sys_common::backtrace::print::h00d3155a29b4020f
Dec 03 13:52:28.291 INFO [stdout]                                at libstd/sys_common/backtrace.rs:71
Dec 03 13:52:28.291 INFO [stdout]                                at libstd/sys_common/backtrace.rs:59
Dec 03 13:52:28.291 INFO [stdout]    2:     0x5584b44f94ef - std::panicking::default_hook::{{closure}}::hd7465c742e79e1bb
Dec 03 13:52:28.291 INFO [stdout]                                at libstd/panicking.rs:211
Dec 03 13:52:28.291 INFO [stdout]    3:     0x5584b44f91ee - std::panicking::default_hook::h9e3966f2aa2a6e12
Dec 03 13:52:28.291 INFO [stdout]                                at libstd/panicking.rs:221
Dec 03 13:52:28.291 INFO [stdout]    4:     0x5584b44f9bce - std::panicking::rust_panic_with_hook::h587f091ae747d080
Dec 03 13:52:28.291 INFO [stdout]                                at libstd/panicking.rs:476
Dec 03 13:52:28.291 INFO [stdout]    5:     0x5584b44f9771 - std::panicking::continue_panic_fmt::hb3a4fdede87f43a8
Dec 03 13:52:28.291 INFO [stdout]                                at libstd/panicking.rs:390
Dec 03 13:52:28.291 INFO [stdout]    6:     0x5584b44f9655 - rust_begin_unwind
Dec 03 13:52:28.291 INFO [stdout]                                at libstd/panicking.rs:325
Dec 03 13:52:28.291 INFO [stdout]    7:     0x5584b453e92c - core::panicking::panic_fmt::h6f9cebf5e7474a0b
Dec 03 13:52:28.291 INFO [stdout]                                at libcore/panicking.rs:77
Dec 03 13:52:28.291 INFO [stdout]    8:     0x5584b4256afb - core::result::unwrap_failed::h21d302bdc6fc52f9
Dec 03 13:52:28.291 INFO [stdout]                                at libcore/macros.rs:26
Dec 03 13:52:28.291 INFO [stdout]    9:     0x5584b4255cc6 - <core::result::Result<T, E>>::expect::hcc23c6d88f9eb833
Dec 03 13:52:28.291 INFO [stdout]                                at libcore/result.rs:835
Dec 03 13:52:28.291 INFO [stdout]   10:     0x5584b425fde6 - integration_tests::helpers::TestApp::assert_run_ok::h0aab125f1099414b
Dec 03 13:52:28.291 INFO [stdout]                                at tests/helpers/mod.rs:57
Dec 03 13:52:28.291 INFO [stdout]   11:     0x5584b425a4b2 - integration_tests::install_workflow_step_by_step::h8962a5864680e49f
Dec 03 13:52:28.291 INFO [stdout]                                at tests/integration_tests.rs:77
Dec 03 13:52:28.291 INFO [stdout]   12:     0x5584b425a339 - integration_tests::install_workflow_step_by_step::{{closure}}::hf4efb28e0950f853
Dec 03 13:52:28.291 INFO [stdout]                                at tests/integration_tests.rs:72
Dec 03 13:52:28.291 INFO [stdout]   13:     0x5584b425366d - core::ops::function::FnOnce::call_once::hbec59195ddfce3c8
Dec 03 13:52:28.291 INFO [stdout]                                at libcore/ops/function.rs:238
Dec 03 13:52:28.291 INFO [stdout]   14:     0x5584b4263dde - <F as alloc::boxed::FnBox<A>>::call_box::hac903f91577a156c
Dec 03 13:52:28.291 INFO [stdout]                                at libtest/lib.rs:1468
Dec 03 13:52:28.291 INFO [stdout]                                at libcore/ops/function.rs:238
Dec 03 13:52:28.291 INFO [stdout]                                at liballoc/boxed.rs:672
Dec 03 13:52:28.291 INFO [stdout]   15:     0x5584b450bb79 - __rust_maybe_catch_panic
Dec 03 13:52:28.291 INFO [stdout]                                at libpanic_unwind/lib.rs:102
Dec 03 13:52:28.291 INFO [stdout]   16:     0x5584b4285a32 - std::sys_common::backtrace::__rust_begin_short_backtrace::hb8f0d0309526c184
Dec 03 13:52:28.291 INFO [stdout]                                at libstd/panicking.rs:289
Dec 03 13:52:28.291 INFO [stdout]                                at libstd/panic.rs:392
Dec 03 13:52:28.291 INFO [stdout]                                at libtest/lib.rs:1423
Dec 03 13:52:28.291 INFO [stdout]                                at libstd/sys_common/backtrace.rs:136
Dec 03 13:52:28.291 INFO [stdout]   17:     0x5584b42864e4 - std::panicking::try::do_call::hc2f7c8b9a348d8db
Dec 03 13:52:28.291 INFO [stdout]                                at libstd/thread/mod.rs:409
Dec 03 13:52:28.291 INFO [stdout]                                at libstd/panic.rs:313
Dec 03 13:52:28.291 INFO [stdout]                                at libstd/panicking.rs:310
Dec 03 13:52:28.291 INFO [stdout]   18:     0x5584b450bb79 - __rust_maybe_catch_panic
Dec 03 13:52:28.291 INFO [stdout]                                at libpanic_unwind/lib.rs:102
Dec 03 13:52:28.291 INFO [stdout]   19:     0x5584b427371c - <F as alloc::boxed::FnBox<A>>::call_box::h6bfded618e480b32
Dec 03 13:52:28.291 INFO [stdout]                                at libstd/panicking.rs:289
Dec 03 13:52:28.291 INFO [stdout]                                at libstd/panic.rs:392
Dec 03 13:52:28.291 INFO [stdout]                                at libstd/thread/mod.rs:408
Dec 03 13:52:28.291 INFO [stdout]                                at liballoc/boxed.rs:672
Dec 03 13:52:28.291 INFO [stdout]   20:     0x5584b44f637d - std::sys_common::thread::start_thread::h4c28257d67cdf9cb
Dec 03 13:52:28.291 INFO [stdout]                                at liballoc/boxed.rs:682
Dec 03 13:52:28.291 INFO [stdout]                                at libstd/sys_common/thread.rs:24
Dec 03 13:52:28.291 INFO [stdout]   21:     0x5584b44e8475 - std::sys::unix::thread::Thread::new::thread_start::hfecb2e7eff8fd34f
Dec 03 13:52:28.291 INFO [stdout]                                at libstd/sys/unix/thread.rs:90
Dec 03 13:52:28.291 INFO [stdout]   22:     0x7f4a45c7a6b9 - start_thread
Dec 03 13:52:28.291 INFO [stdout]   23:     0x7f4a4579a41c - clone
Dec 03 13:52:28.291 INFO [stdout]   24:                0x0 - <unknown>
Dec 03 13:52:28.291 INFO [stdout] 
Dec 03 13:52:28.291 INFO [stdout] ---- lock_workflow stdout ----
Dec 03 13:52:28.291 INFO [stdout] [34m::[0m Creating virtualenv in: /tmp/test-dmenv.Y9IlLryhP4k2/.venv/3.5.2
Dec 03 13:52:28.291 INFO [stdout] [34m->[0m running [1m/usr/bin/python3[0m -m venv /tmp/test-dmenv.Y9IlLryhP4k2/.venv/3.5.2
Dec 03 13:52:28.291 INFO [stdout] [34m->[0m running [1m/tmp/test-dmenv.Y9IlLryhP4k2/.venv/3.5.2/bin/python[0m -m pip install pip --upgrade
Dec 03 13:52:28.291 INFO [stdout] thread 'lock_workflow' panicked at ': Error { description: "command failed" }', libcore/result.rs:1009:5
Dec 03 13:52:28.291 INFO [stdout] stack backtrace:
Dec 03 13:52:28.291 INFO [stdout]    0:     0x5584b44f3bff - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h3a8b83e1922cbdde
Dec 03 13:52:28.291 INFO [stdout]                                at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49
Dec 03 13:52:28.291 INFO [stdout]    1:     0x5584b4500bc7 - std::sys_common::backtrace::print::h00d3155a29b4020f
Dec 03 13:52:28.291 INFO [stdout]                                at libstd/sys_common/backtrace.rs:71
Dec 03 13:52:28.291 INFO [stdout]                                at libstd/sys_common/backtrace.rs:59
Dec 03 13:52:28.291 INFO [stdout]    2:     0x5584b44f94ef - std::panicking::default_hook::{{closure}}::hd7465c742e79e1bb
Dec 03 13:52:28.291 INFO [stdout]                                at libstd/panicking.rs:211
Dec 03 13:52:28.291 INFO [stdout]    3:     0x5584b44f91ee - std::panicking::default_hook::h9e3966f2aa2a6e12
Dec 03 13:52:28.292 INFO [stdout]                                at libstd/panicking.rs:221
Dec 03 13:52:28.292 INFO [stdout]    4:     0x5584b44f9bce - std::panicking::rust_panic_with_hook::h587f091ae747d080
Dec 03 13:52:28.292 INFO [stdout]                                at libstd/panicking.rs:476
Dec 03 13:52:28.292 INFO [stdout]    5:     0x5584b44f9771 - std::panicking::continue_panic_fmt::hb3a4fdede87f43a8
Dec 03 13:52:28.292 INFO [stdout]                                at libstd/panicking.rs:390
Dec 03 13:52:28.292 INFO [stdout]    6:     0x5584b44f9655 - rust_begin_unwind
Dec 03 13:52:28.292 INFO [stdout]                                at libstd/panicking.rs:325
Dec 03 13:52:28.292 INFO [stdout]    7:     0x5584b453e92c - core::panicking::panic_fmt::h6f9cebf5e7474a0b
Dec 03 13:52:28.292 INFO [stdout]                                at libcore/panicking.rs:77
Dec 03 13:52:28.292 INFO [stdout]    8:     0x5584b4256afb - core::result::unwrap_failed::h21d302bdc6fc52f9
Dec 03 13:52:28.292 INFO [stdout]                                at libcore/macros.rs:26
Dec 03 13:52:28.292 INFO [stdout]    9:     0x5584b4255cc6 - <core::result::Result<T, E>>::expect::hcc23c6d88f9eb833
Dec 03 13:52:28.292 INFO [stdout]                                at libcore/result.rs:835
Dec 03 13:52:28.292 INFO [stdout]   10:     0x5584b425fde6 - integration_tests::helpers::TestApp::assert_run_ok::h0aab125f1099414b
Dec 03 13:52:28.292 INFO [stdout]                                at tests/helpers/mod.rs:57
Dec 03 13:52:28.292 INFO [stdout]   11:     0x5584b4259ec7 - integration_tests::lock_workflow::hf4334c65f0b7ff3f
Dec 03 13:52:28.292 INFO [stdout]                                at tests/integration_tests.rs:53
Dec 03 13:52:28.292 INFO [stdout]   12:     0x5584b4259d99 - integration_tests::lock_workflow::{{closure}}::h25fbd9877877c3a2
Dec 03 13:52:28.292 INFO [stdout]                                at tests/integration_tests.rs:50
Dec 03 13:52:28.292 INFO [stdout]   13:     0x5584b42535ad - core::ops::function::FnOnce::call_once::hac0498d4f352caa8
Dec 03 13:52:28.292 INFO [stdout]                                at libcore/ops/function.rs:238
Dec 03 13:52:28.292 INFO [stdout]   14:     0x5584b4263dde - <F as alloc::boxed::FnBox<A>>::call_box::hac903f91577a156c
Dec 03 13:52:28.292 INFO [stdout]                                at libtest/lib.rs:1468
Dec 03 13:52:28.292 INFO [stdout]                                at libcore/ops/function.rs:238
Dec 03 13:52:28.292 INFO [stdout]                                at liballoc/boxed.rs:672
Dec 03 13:52:28.292 INFO [stdout]   15:     0x5584b450bb79 - __rust_maybe_catch_panic
Dec 03 13:52:28.292 INFO [stdout]                                at libpanic_unwind/lib.rs:102
Dec 03 13:52:28.292 INFO [stdout]   16:     0x5584b4285a32 - std::sys_common::backtrace::__rust_begin_short_backtrace::hb8f0d0309526c184
Dec 03 13:52:28.292 INFO [stdout]                                at libstd/panicking.rs:289
Dec 03 13:52:28.292 INFO [stdout]                                at libstd/panic.rs:392
Dec 03 13:52:28.292 INFO [stdout]                                at libtest/lib.rs:1423
Dec 03 13:52:28.292 INFO [stdout]                                at libstd/sys_common/backtrace.rs:136
Dec 03 13:52:28.292 INFO [stdout]   17:     0x5584b42864e4 - std::panicking::try::do_call::hc2f7c8b9a348d8db
Dec 03 13:52:28.292 INFO [stdout]                                at libstd/thread/mod.rs:409
Dec 03 13:52:28.292 INFO [stdout]                                at libstd/panic.rs:313
Dec 03 13:52:28.292 INFO [stdout]                                at libstd/panicking.rs:310
Dec 03 13:52:28.292 INFO [stdout]   18:     0x5584b450bb79 - __rust_maybe_catch_panic
Dec 03 13:52:28.292 INFO [stdout]                                at libpanic_unwind/lib.rs:102
Dec 03 13:52:28.292 INFO [stdout]   19:     0x5584b427371c - <F as alloc::boxed::FnBox<A>>::call_box::h6bfded618e480b32
Dec 03 13:52:28.292 INFO [stdout]                                at libstd/panicking.rs:289
Dec 03 13:52:28.292 INFO [stdout]                                at libstd/panic.rs:392
Dec 03 13:52:28.292 INFO [stdout]                                at libstd/thread/mod.rs:408
Dec 03 13:52:28.292 INFO [stdout]                                at liballoc/boxed.rs:672
Dec 03 13:52:28.292 INFO [stdout]   20:     0x5584b44f637d - std::sys_common::thread::start_thread::h4c28257d67cdf9cb
Dec 03 13:52:28.292 INFO [stdout]                                at liballoc/boxed.rs:682
Dec 03 13:52:28.292 INFO [stdout]                                at libstd/sys_common/thread.rs:24
Dec 03 13:52:28.292 INFO [stdout]   21:     0x5584b44e8475 - std::sys::unix::thread::Thread::new::thread_start::hfecb2e7eff8fd34f
Dec 03 13:52:28.292 INFO [stdout]                                at libstd/sys/unix/thread.rs:90
Dec 03 13:52:28.292 INFO [stdout]   22:     0x7f4a45c7a6b9 - start_thread
Dec 03 13:52:28.292 INFO [stdout]   23:     0x7f4a4579a41c - clone
Dec 03 13:52:28.292 INFO [stdout]   24:                0x0 - <unknown>
Dec 03 13:52:28.292 INFO [stdout] 
Dec 03 13:52:28.292 INFO [stdout] ---- install_workflow_all_in_one stdout ----
Dec 03 13:52:28.292 INFO [stdout] [34m::[0m Creating virtualenv in: /tmp/test-dmenv.S3a86RelmbYi/.venv/3.5.2
Dec 03 13:52:28.292 INFO [stdout] [34m->[0m running [1m/usr/bin/python3[0m -m venv /tmp/test-dmenv.S3a86RelmbYi/.venv/3.5.2
Dec 03 13:52:28.292 INFO [stdout] [34m->[0m running [1m/tmp/test-dmenv.S3a86RelmbYi/.venv/3.5.2/bin/python[0m -m pip install pip --upgrade
Dec 03 13:52:28.292 INFO [stdout] thread 'install_workflow_all_in_one' panicked at ': Error { description: "command failed" }', libcore/result.rs:1009:5
Dec 03 13:52:28.292 INFO [stdout] stack backtrace:
Dec 03 13:52:28.292 INFO [stdout]    0:     0x5584b44f3bff - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h3a8b83e1922cbdde
Dec 03 13:52:28.292 INFO [stdout]                                at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49
Dec 03 13:52:28.292 INFO [stdout]    1:     0x5584b4500bc7 - std::sys_common::backtrace::print::h00d3155a29b4020f
Dec 03 13:52:28.292 INFO [stdout]                                at libstd/sys_common/backtrace.rs:71
Dec 03 13:52:28.292 INFO [stdout]                                at libstd/sys_common/backtrace.rs:59
Dec 03 13:52:28.292 INFO [stdout]    2:     0x5584b44f94ef - std::panicking::default_hook::{{closure}}::hd7465c742e79e1bb
Dec 03 13:52:28.292 INFO [stdout]                                at libstd/panicking.rs:211
Dec 03 13:52:28.292 INFO [stdout]    3:     0x5584b44f91ee - std::panicking::default_hook::h9e3966f2aa2a6e12
Dec 03 13:52:28.292 INFO [stdout]                                at libstd/panicking.rs:221
Dec 03 13:52:28.292 INFO [stdout]    4:     0x5584b44f9bce - std::panicking::rust_panic_with_hook::h587f091ae747d080
Dec 03 13:52:28.292 INFO [stdout]                                at libstd/panicking.rs:476
Dec 03 13:52:28.292 INFO [stdout]    5:     0x5584b44f9771 - std::panicking::continue_panic_fmt::hb3a4fdede87f43a8
Dec 03 13:52:28.292 INFO [stdout]                                at libstd/panicking.rs:390
Dec 03 13:52:28.292 INFO [stdout]    6:     0x5584b44f9655 - rust_begin_unwind
Dec 03 13:52:28.292 INFO [stdout]                                at libstd/panicking.rs:325
Dec 03 13:52:28.292 INFO [stdout]    7:     0x5584b453e92c - core::panicking::panic_fmt::h6f9cebf5e7474a0b
Dec 03 13:52:28.292 INFO [stdout]                                at libcore/panicking.rs:77
Dec 03 13:52:28.292 INFO [stdout]    8:     0x5584b4256afb - core::result::unwrap_failed::h21d302bdc6fc52f9
Dec 03 13:52:28.292 INFO [stdout]                                at libcore/macros.rs:26
Dec 03 13:52:28.292 INFO [stdout]    9:     0x5584b4255cc6 - <core::result::Result<T, E>>::expect::hcc23c6d88f9eb833
Dec 03 13:52:28.292 INFO [stdout]                                at libcore/result.rs:835
Dec 03 13:52:28.292 INFO [stdout]   10:     0x5584b425fde6 - integration_tests::helpers::TestApp::assert_run_ok::h0aab125f1099414b
Dec 03 13:52:28.292 INFO [stdout]                                at tests/helpers/mod.rs:57
Dec 03 13:52:28.292 INFO [stdout]   11:     0x5584b425a2da - integration_tests::install_workflow_all_in_one::hbea958d826dc33c0
Dec 03 13:52:28.292 INFO [stdout]                                at tests/integration_tests.rs:68
Dec 03 13:52:28.292 INFO [stdout]   12:     0x5584b425a189 - integration_tests::install_workflow_all_in_one::{{closure}}::ha9dbb47770417c3d
Dec 03 13:52:28.292 INFO [stdout]                                at tests/integration_tests.rs:63
Dec 03 13:52:28.292 INFO [stdout]   13:     0x5584b42534ed - core::ops::function::FnOnce::call_once::h926413992f2783a2
Dec 03 13:52:28.292 INFO [stdout]                                at libcore/ops/function.rs:238
Dec 03 13:52:28.292 INFO [stdout]   14:     0x5584b4263dde - <F as alloc::boxed::FnBox<A>>::call_box::hac903f91577a156c
Dec 03 13:52:28.292 INFO [stdout]                                at libtest/lib.rs:1468
Dec 03 13:52:28.292 INFO [stdout]                                at libcore/ops/function.rs:238
Dec 03 13:52:28.292 INFO [stdout]                                at liballoc/boxed.rs:672
Dec 03 13:52:28.292 INFO [stdout]   15:     0x5584b450bb79 - __rust_maybe_catch_panic
Dec 03 13:52:28.293 INFO [stdout]                                at libpanic_unwind/lib.rs:102
Dec 03 13:52:28.293 INFO [stdout]   16:     0x5584b4285a32 - std::sys_common::backtrace::__rust_begin_short_backtrace::hb8f0d0309526c184
Dec 03 13:52:28.293 INFO [stdout]                                at libstd/panicking.rs:289
Dec 03 13:52:28.293 INFO [stdout]                                at libstd/panic.rs:392
Dec 03 13:52:28.293 INFO [stdout]                                at libtest/lib.rs:1423
Dec 03 13:52:28.293 INFO [stdout]                                at libstd/sys_common/backtrace.rs:136
Dec 03 13:52:28.293 INFO [stdout]   17:     0x5584b42864e4 - std::panicking::try::do_call::hc2f7c8b9a348d8db
Dec 03 13:52:28.293 INFO [stdout]                                at libstd/thread/mod.rs:409
Dec 03 13:52:28.293 INFO [stdout]                                at libstd/panic.rs:313
Dec 03 13:52:28.293 INFO [stdout]                                at libstd/panicking.rs:310
Dec 03 13:52:28.293 INFO [stdout]   18:     0x5584b450bb79 - __rust_maybe_catch_panic
Dec 03 13:52:28.293 INFO [stdout]                                at libpanic_unwind/lib.rs:102
Dec 03 13:52:28.293 INFO [stdout]   19:     0x5584b427371c - <F as alloc::boxed::FnBox<A>>::call_box::h6bfded618e480b32
Dec 03 13:52:28.293 INFO [stdout]                                at libstd/panicking.rs:289
Dec 03 13:52:28.293 INFO [stdout]                                at libstd/panic.rs:392
Dec 03 13:52:28.293 INFO [stdout]                                at libstd/thread/mod.rs:408
Dec 03 13:52:28.293 INFO [stdout]                                at liballoc/boxed.rs:672
Dec 03 13:52:28.293 INFO [stdout]   20:     0x5584b44f637d - std::sys_common::thread::start_thread::h4c28257d67cdf9cb
Dec 03 13:52:28.293 INFO [stdout]                                at liballoc/boxed.rs:682
Dec 03 13:52:28.293 INFO [stdout]                                at libstd/sys_common/thread.rs:24
Dec 03 13:52:28.293 INFO [stdout]   21:     0x5584b44e8475 - std::sys::unix::thread::Thread::new::thread_start::hfecb2e7eff8fd34f
Dec 03 13:52:28.293 INFO [stdout]                                at libstd/sys/unix/thread.rs:90
Dec 03 13:52:28.293 INFO [stdout]   22:     0x7f4a45c7a6b9 - start_thread
Dec 03 13:52:28.293 INFO [stdout]   23:     0x7f4a4579a41c - clone
Dec 03 13:52:28.293 INFO [stdout]   24:                0x0 - <unknown>
Dec 03 13:52:28.293 INFO [stdout] 
Dec 03 13:52:28.293 INFO [stdout] 
Dec 03 13:52:28.293 INFO [stdout] failures:
Dec 03 13:52:28.293 INFO [stdout]     install_workflow_all_in_one
Dec 03 13:52:28.293 INFO [stdout]     install_workflow_step_by_step
Dec 03 13:52:28.293 INFO [stdout]     lock_workflow
Dec 03 13:52:28.293 INFO [stdout] 
Dec 03 13:52:28.293 INFO [stdout] test result: FAILED. 7 passed; 3 failed; 0 ignored; 0 measured; 0 filtered out
Dec 03 13:52:28.293 INFO [stdout] 
Dec 03 13:52:28.293 INFO [stderr] su: No module specific data is present
Dec 03 13:52:28.620 INFO running `"docker" "inspect" "bc0e766468fb5cd8c81c02d4a6b2ebb59fbe1922e37b22220a29110b141a3542"`
Dec 03 13:52:28.798 INFO running `"docker" "rm" "-f" "bc0e766468fb5cd8c81c02d4a6b2ebb59fbe1922e37b22220a29110b141a3542"`
Dec 03 13:52:28.934 INFO [stdout] bc0e766468fb5cd8c81c02d4a6b2ebb59fbe1922e37b22220a29110b141a3542
